**** dT 0.000 * top TEST ../../vmod/tests/std_c00002.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=fc00:a462:8e9e:946a:5df3:31c6:d23:18dc **** top extmacro def bad_backend=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:63561 **** top extmacro def listen_addr=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build **** top extmacro def topsrc=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/.. **** top macro def testdir=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/../../vmod/tests **** top macro def tmpdir=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0 **** top macro def vtcid=vtc.552733.140868d0 ** top === varnishtest "std.ip(), .port() and .set_ip_tos() don't work ... * top VTEST std.ip(), .port() and .set_ip_tos() don't work for UDS addresses ** top === varnish v1 -arg "-a ${tmpdir}/v1.sock -b '${bad_backend}'" -... **** dT 0.013 ** v1 Launch *** v1 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 47080' -P /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock -b '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:63561' *** v1 CMD: cd /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 47080' -P /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock -b '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:63561' *** v1 PID: 552755 **** v1 macro def v1_pid=552755 **** v1 macro def v1_name=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1 **** dT 0.493 *** v1 debug|Warnings: *** v1 debug|Message from VCC-compiler: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug|Message from C-compiler: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug|Message from dlopen: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug| **** dT 0.503 *** v1 debug|Warning: mlock() of VSM failed: Not owner *** v1 debug| (1) **** dT 0.507 *** v1 debug|Debug: Version: varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v1 debug|Debug: Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|200 290 *** v1 debug|----------------------------- *** v1 debug|Varnish Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.605 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 *** v1 CLI RX 107 **** v1 CLI RX|obiqmwgsllvmlxofznxheusmsecxtzuk **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** dT 0.606 **** v1 CLI TX|auth 658c36056774d4740e3520a913c708126a5b047369315d1d5691bee6484861dc *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Varnish Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. ** v1 Start **** v1 CLI TX|start **** dT 0.617 *** v1 debug|Debug: *** v1 debug| Child (552814) Started **** dT 0.648 *** v1 debug|Child launched OK **** dT 0.660 *** v1 CLI RX 200 *** v1 wait-running **** v1 CLI TX|status *** v1 debug|Info: Child (552814) said Child starts *** v1 debug|Info: Child (552814) said Security: Privilege proc_setid missing, will not change uid/gid *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock - **** v1 CLI TX|debug.xid 1000 *** v1 CLI RX 200 **** v1 CLI RX|XID is 1000 chunk 1 **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock - ** v1 Listen on 0.0.0.0 0 **** v1 macro def v1_addr=0.0.0.0 **** v1 macro def v1_port=0 **** v1 macro def v1_sock=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** v1 macro def v1_a0_addr=0.0.0.0 **** v1 macro def v1_a0_port=0 **** v1 macro def v1_a0_sock=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** dT 0.707 **** v1 vsl| 0 CLI - Rd vcl.load "boot" vcl_boot.1761556065.109378/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_boot.1761556065.109378/vgc.so" as "boot" **** v1 vsl| 0 CLI - Rd vcl.use "boot" **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd start **** v1 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock - **** v1 vsl| 0 CLI - Rd debug.xid 1000 **** v1 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock - **** dT 0.760 ** top === varnish v2 -arg "-a ${tmpdir}/v2.sock" -vcl { **** dT 0.770 ** v2 Launch *** v2 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 35207' -P /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock *** v2 CMD: cd /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 35207' -P /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** dT 0.771 *** v2 PID: 552824 **** v2 macro def v2_pid=552824 **** v2 macro def v2_name=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2 **** dT 0.803 *** v2 debug|Warning: mlock() of VSM failed: Not owner (1 *** v2 debug|) **** dT 0.806 *** v2 debug|Debug: Version: varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v2 debug|Debug: Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|200 290 *** v2 debug|----------------------------- *** v2 debug|Varnish Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 0.904 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 15 *** v2 CLI RX 107 **** v2 CLI RX|kugizkmbdyytgsdzocrbtxuaxyioppwz **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** v2 CLI TX|auth 6456e38c700875ab9463044d0fd0006bb37a7df33be20cdbed1ea46ba2fb6a18 **** dT 0.905 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Varnish Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v2 CLI RX|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** v2 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.1; **** v2 CLI TX| **** v2 CLI TX|\timport std; **** v2 CLI TX|\tbackend default None; **** v2 CLI TX| **** v2 CLI TX|\tsub vcl_recv { **** v2 CLI TX|\t\treturn(synth(200)); **** v2 CLI TX|\t} **** v2 CLI TX| **** v2 CLI TX|\tsub vcl_synth { **** v2 CLI TX|\t\tset resp.http.v1_addr = std.ip("/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v1.sock", client.ip); **** v2 CLI TX|\t} **** v2 CLI TX| **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 1.005 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.105 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.206 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.306 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.403 *** v2 CLI RX 200 **** v2 CLI RX|Message from VCC-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|Message from C-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|Message from dlopen: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|VCL compiled. **** v2 CLI TX|vcl.use vcl1 *** v2 CLI RX 200 **** v2 CLI RX|VCL 'vcl1' now active ** v2 Start **** v2 CLI TX|start **** dT 1.406 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.415 *** v2 debug|Debug: *** v2 debug| Child (552886) Started **** dT 1.448 *** v2 debug|Child launched OK **** dT 1.490 *** v2 CLI RX 200 *** v2 wait-running **** v2 CLI TX|status *** v2 debug|Info: Child (552886) said Child starts *** v2 debug|Info: Child (552886) said Security: Privilege proc_setid missing, will not change uid/gid *** v2 CLI RX 200 **** v2 CLI RX|Child in state running **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock - **** v2 CLI TX|debug.xid 1000 *** v2 CLI RX 200 **** v2 CLI RX|XID is 1000 chunk 1 **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock - ** v2 Listen on 0.0.0.0 0 **** v2 macro def v2_addr=0.0.0.0 **** v2 macro def v2_port=0 **** v2 macro def v2_sock=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 macro def v2_a0_addr=0.0.0.0 **** v2 macro def v2_a0_port=0 **** v2 macro def v2_a0_sock=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** dT 1.506 **** v2 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1761556065.975267/vgc.so 1auto **** v2 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1761556065.975267/vgc.so" as "vcl1" **** v2 vsl| 0 CLI - Rd vcl.use "vcl1" **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd start **** v2 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock - **** v2 vsl| 0 CLI - Rd debug.xid 1000 **** v2 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock - **** dT 1.590 ** top === client c1 -connect "${v2_sock}" { **** dT 1.591 ** c1 Starting client ** c1 Waiting for client ** c1 Started on /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock (1 iterations) *** c1 Connect to /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock *** c1 connected fd 25 to /tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock ** c1 === txreq **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: fc00:a462:8e9e:946a:5df3:31c6:d23:18dc\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** dT 1.606 **** v2 vsl| 1000 Begin c sess 0 HTTP/1 **** v2 vsl| 1000 SessOpen c 0.0.0.0 0 a0 0.0.0.0 0 1761556066.660949 16 **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_KEEPALIVE non heredity for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_SNDTIMEO non heredity for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_RCVTIMEO non heredity for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m64_IPv6_uffix/_vtest_tmp/vtc.552733.140868d0/v2.sock **** v2 vsl| 1000 Link c req 1001 rxreq **** dT 3.708 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556068 1.0 **** dT 4.508 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556069 1.0 **** dT 6.710 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556071 1.0 **** dT 7.510 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556072 1.0 **** dT 9.711 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556074 1.0 **** dT 10.511 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556075 1.0 **** dT 12.713 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556077 1.0 **** dT 13.513 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556078 1.0 **** dT 15.715 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556080 1.0 **** dT 16.515 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556081 1.0 **** dT 18.717 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556083 1.0 **** dT 19.517 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556084 1.0 **** dT 21.718 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556086 1.0 **** dT 22.519 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556087 1.0 **** dT 24.720 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556089 1.0 **** dT 25.521 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556090 1.0 **** dT 27.722 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556092 1.0 **** dT 28.523 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556093 1.0 **** dT 30.724 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556095 1.0 **** dT 31.524 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556096 1.0 **** dT 31.591 ---- c1 HTTP rx timeout (fd:25 30.000s) * top Aborting execution, test failed * top RESETTING after ../../vmod/tests/std_c00002.vtc ** v1 Wait **** v1 CLI TX|panic.show *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared **** dT 31.592 *** v1 debug|Info: manager stopping child *** v1 debug|Debug: Stopping Child **** dT 31.624 **** v1 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 31.692 *** v1 debug|Info: Child (552814) said Child dies *** v1 debug| *** v1 debug|Info: *** v1 debug|Child (552814) ended *** v1 debug| *** v1 debug|Debug: *** v1 debug|Child cleanup complete *** v1 debug|Info: manager dies *** v1 debug| **** v1 STDOUT EOF **** dT 31.725 ** v1 WAIT4 pid=552755 status=0x0000 (user 0.392000 sys 0.134000) ** v2 Wait **** v2 CLI TX|panic.show *** v2 CLI RX 300 **** v2 CLI RX|Child has not panicked or panic has been cleared *** v2 debug|Info: manager stopping child *** v2 debug|Debug: Stopping Child **** dT 31.825 **** v2 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 32.725 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 33.726 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 34.726 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 35.726 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 36.727 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 37.727 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 38.628 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 39.628 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 40.628 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 41.629 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 42.629 *** v2 debug|Info: Child (552886) said shutdown waiting for 1 reference on vcl1 **** dT 42.832 **** v2 vsl| 1001 Begin c req 1000 rxreq **** v2 vsl| 1001 Timestamp c Start: 1761556066.661103 0.000000 0.000000 **** v2 vsl| 1001 Timestamp c Req: 1761556066.661103 0.000000 0.000000 **** v2 vsl| 1001 VCL_use c vcl1 **** v2 vsl| 1001 ReqStart c 0.0.0.0 0 a0 **** v2 vsl| 1001 ReqMethod c GET **** v2 vsl| 1001 ReqURL c / **** v2 vsl| 1001 ReqProtocol c HTTP/1.1 **** v2 vsl| 1001 ReqHeader c Host: fc00:a462:8e9e:946a:5df3:31c6:d23:18dc **** v2 vsl| 1001 ReqHeader c User-Agent: c1 **** v2 vsl| 1001 ReqHeader c X-Forwarded-For: 0.0.0.0 **** v2 vsl| 1001 ReqHeader c Via: 1.1 v2 (Varnish/trunk) **** v2 vsl| 1001 VCL_call c RECV **** v2 vsl| 1001 VCL_return c synth **** v2 vsl| 1001 VCL_call c HASH **** v2 vsl| 1001 VCL_return c lookup **** v2 vsl| 1001 RespProtocol c HTTP/1.1 **** v2 vsl| 1001 RespStatus c 200 **** v2 vsl| 1001 RespReason c OK **** v2 vsl| 1001 RespHeader c Date: Mon, 27 Oct 2025 09:07:46 GMT **** v2 vsl| 1001 RespHeader c Server: Varnish **** v2 vsl| 1001 RespHeader c X-Varnish: 1001 **** v2 vsl| 1001 VCL_call c SYNTH **** v2 vsl| 1001 RespHeader c v1_addr: 0.0.0.0 **** v2 vsl| 1001 RespHeader c Content-Type: text/html; charset=utf-8 **** v2 vsl| 1001 RespHeader c Retry-After: 5 **** v2 vsl| 1001 VCL_return c deliver **** v2 vsl| 1001 Timestamp c Process: 1761556107.889980 41.228877 41.228877 **** v2 vsl| 1001 RespHeader c Content-Length: 227 **** v2 vsl| 1001 Storage c umem Transient **** v2 vsl| 1001 Filters c **** v2 vsl| 1001 RespHeader c Connection: keep-alive **** v2 vsl| 1001 Timestamp c Resp: 1761556107.890400 41.229296 0.000419 **** v2 vsl| 1001 ReqAcct c 80 0 80 209 227 436 **** v2 vsl| 1001 End c **** dT 42.929 *** v2 debug|Info: Child (552886) said Child dies *** v2 debug| **** dT 43.029 *** v2 debug|Info: Child (552886) ended *** v2 debug|Debug: *** v2 debug|Child cleanup complete *** v2 debug|Info: **** dT 43.030 *** v2 debug|manager dies **** v2 STDOUT EOF **** dT 43.032 ** v2 WAIT4 pid=552824 status=0x0000 (user 0.417000 sys 0.193000) * top TEST ../../vmod/tests/std_c00002.vtc FAILED # top TEST ../../vmod/tests/std_c00002.vtc FAILED (43.035) exit=2